Dec 03 05:34:11.950 INFO testing http-test-server-1.0.0 against master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61 for beta-1.31-5
Dec 03 05:34:11.951 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-2/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/beta-1.31-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +8940af3cea4a9a9deabcaf843b2f8cad9a83fc61-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 03 05:34:12.459 INFO [stdout] 80db4a5354deccbc39af67c6125e453626ce27b98a1d3720ec49fbf1359f3df3
Dec 03 05:34:12.461 INFO running `"docker" "start" "-a" "80db4a5354deccbc39af67c6125e453626ce27b98a1d3720ec49fbf1359f3df3"`
Dec 03 05:34:13.709 INFO [stderr] usermod: no changes
Dec 03 05:34:13.765 INFO [stderr]    Compiling http-test-server v1.0.0 (/source)
Dec 03 05:34:15.989 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2.25s
Dec 03 05:34:15.991 INFO [stderr] su: No module specific data is present
Dec 03 05:34:16.352 INFO running `"docker" "inspect" "80db4a5354deccbc39af67c6125e453626ce27b98a1d3720ec49fbf1359f3df3"`
Dec 03 05:34:16.450 INFO running `"docker" "rm" "-f" "80db4a5354deccbc39af67c6125e453626ce27b98a1d3720ec49fbf1359f3df3"`
Dec 03 05:34:16.571 INFO [stdout] 80db4a5354deccbc39af67c6125e453626ce27b98a1d3720ec49fbf1359f3df3
Dec 03 05:34:16.577 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-2/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/beta-1.31-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +8940af3cea4a9a9deabcaf843b2f8cad9a83fc61-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 03 05:34:16.823 INFO [stdout] a1c5018764a5f7abd5c38c91bb5f16fc10129e7860a91e551fcb4dda64cb34b0
Dec 03 05:34:16.825 INFO running `"docker" "start" "-a" "a1c5018764a5f7abd5c38c91bb5f16fc10129e7860a91e551fcb4dda64cb34b0"`
Dec 03 05:34:17.313 INFO [stderr] usermod: no changes
Dec 03 05:34:17.339 INFO [stderr]    Compiling http-test-server v1.0.0 (/source)
Dec 03 05:34:21.019 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 3.70s
Dec 03 05:34:21.021 INFO [stderr] su: No module specific data is present
Dec 03 05:34:21.300 INFO running `"docker" "inspect" "a1c5018764a5f7abd5c38c91bb5f16fc10129e7860a91e551fcb4dda64cb34b0"`
Dec 03 05:34:21.423 INFO running `"docker" "rm" "-f" "a1c5018764a5f7abd5c38c91bb5f16fc10129e7860a91e551fcb4dda64cb34b0"`
Dec 03 05:34:21.575 INFO [stdout] a1c5018764a5f7abd5c38c91bb5f16fc10129e7860a91e551fcb4dda64cb34b0
Dec 03 05:34:21.580 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-2/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/beta-1.31-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +8940af3cea4a9a9deabcaf843b2f8cad9a83fc61-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 03 05:34:21.807 INFO [stdout] 060a30f21aae253877241a6e8086ce0520a7669f49e308a1cc6b33386e65f0a3
Dec 03 05:34:21.809 INFO running `"docker" "start" "-a" "060a30f21aae253877241a6e8086ce0520a7669f49e308a1cc6b33386e65f0a3"`
Dec 03 05:34:22.551 INFO [stderr] usermod: no changes
Dec 03 05:34:22.626 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.03s
Dec 03 05:34:22.644 INFO [stderr]      Running /target/debug/deps/http_test_server-97c0ffad6c1d93d7
Dec 03 05:34:22.663 INFO [stdout] 
Dec 03 05:34:22.663 INFO [stdout] running 29 tests
Dec 03 05:34:22.663 INFO [stdout] test resource::tests::clones_should_share_same_state ... ok
Dec 03 05:34:22.675 INFO [stdout] test resource::tests::should_allows_custom_status ... ok
Dec 03 05:34:22.675 INFO [stdout] test resource::tests::should_convert_to_response_with_body ... ok
Dec 03 05:34:22.675 INFO [stdout] test resource::tests::should_add_headers ... ok
Dec 03 05:34:22.675 INFO [stdout] test resource::tests::should_append_headers ... ok
Dec 03 05:34:22.675 INFO [stdout] test resource::tests::should_increment_request_count ... ok
Dec 03 05:34:22.675 INFO [stdout] test resource::tests::should_send_data_with_line_break ... ok
Dec 03 05:34:22.675 INFO [stdout] test resource::tests::should_return_number_of_connecteds_users ... ok
Dec 03 05:34:22.675 INFO [stdout] test resource::tests::should_overwrite_custom_status_with_status ... ok
Dec 03 05:34:22.675 INFO [stdout] test resource::tests::should_notify_data ... ok
Dec 03 05:34:22.675 INFO [stdout] test resource::tests::should_set_delay ... ok
Dec 03 05:34:22.768 INFO [stdout] test resource::tests::should_convert_to_response_string ... ok
Dec 03 05:34:22.768 INFO [stdout] test resource::tests::should_decrease_count_when_receiver_dropped ... ok
Dec 03 05:34:22.768 INFO [stdout] test resource::tests::should_set_as_stream ... ok
Dec 03 05:34:22.769 INFO [stdout] test resource::tests::should_close_connections ... ok
Dec 03 05:34:22.770 INFO [stdout] test tests::server_should_use_random_port ... ok
Dec 03 05:34:22.774 INFO [stdout] test tests::should_create_resource ... ok
Dec 03 05:34:22.775 INFO [stdout] test tests::returns_404_when_requested_enexistent_resource ... ok
Dec 03 05:34:22.780 INFO [stdout] test tests::should_listen_to_defined_method ... ok
Dec 03 05:34:22.780 INFO [stdout] test tests::should_return_405_when_method_not_defined ... ok
Dec 03 05:34:22.784 INFO [stdout] test tests::should_return_configured_status_for_resource_resource ... ok
Dec 03 05:34:22.784 INFO [stdout] test tests::should_return_resource_body ... ok
Dec 03 05:34:22.872 INFO [stdout] test tests::should_close_client_connections ... ok
Dec 03 05:34:22.885 INFO [stdout] test tests::should_return_requests_metadata ... ok
Dec 03 05:34:22.972 INFO [stdout] test tests::should_expose_stream ... ok
Dec 03 05:34:22.979 INFO [stdout] test tests::should_increment_request_count ... ok
Dec 03 05:34:22.994 INFO [stdout] test tests::should_close_connection ... FAILED
Dec 03 05:34:22.995 INFO [stdout] test tests::server_should_close_connection_when_dropped ... FAILED
Dec 03 05:34:23.170 INFO [stdout] test tests::should_delay_response ... ok
Dec 03 05:34:23.171 INFO [stdout] 
Dec 03 05:34:23.171 INFO [stdout] failures:
Dec 03 05:34:23.171 INFO [stdout] 
Dec 03 05:34:23.171 INFO [stdout] ---- tests::should_close_connection stdout ----
Dec 03 05:34:23.171 INFO [stdout] thread 'tests::should_close_connection' panicked at 'assertion failed: `(left == right)`
Dec 03 05:34:23.172 INFO [stdout]   left: `AddrNotAvailable`,
Dec 03 05:34:23.172 INFO [stdout]  right: `ConnectionRefused`', src/lib.rs:419:13
Dec 03 05:34:23.172 INFO [stdout] stack backtrace:
Dec 03 05:34:23.172 INFO [stdout]    0:     0x55f1f5d9b87f - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h3a8b83e1922cbdde
Dec 03 05:34:23.172 INFO [stdout]                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Dec 03 05:34:23.172 INFO [stdout]    1:     0x55f1f5da5297 - std::sys_common::backtrace::print::h00d3155a29b4020f
Dec 03 05:34:23.172 INFO [stdout]                                at libstd/sys_common/backtrace.rs:71
Dec 03 05:34:23.172 INFO [stdout]                                at libstd/sys_common/backtrace.rs:59
Dec 03 05:34:23.172 INFO [stdout]    2:     0x55f1f5d9fe2f - std::panicking::default_hook::{{closure}}::hd7465c742e79e1bb
Dec 03 05:34:23.172 INFO [stdout]                                at libstd/panicking.rs:211
Dec 03 05:34:23.172 INFO [stdout]    3:     0x55f1f5d9fb2e - std::panicking::default_hook::h9e3966f2aa2a6e12
Dec 03 05:34:23.172 INFO [stdout]                                at libstd/panicking.rs:221
Dec 03 05:34:23.172 INFO [stdout]    4:     0x55f1f5da050e - std::panicking::rust_panic_with_hook::h587f091ae747d080
Dec 03 05:34:23.172 INFO [stdout]                                at libstd/panicking.rs:476
Dec 03 05:34:23.172 INFO [stdout]    5:     0x55f1f5da00b1 - std::panicking::continue_panic_fmt::hb3a4fdede87f43a8
Dec 03 05:34:23.172 INFO [stdout]                                at libstd/panicking.rs:390
Dec 03 05:34:23.172 INFO [stdout]    6:     0x55f1f5d9fffe - std::panicking::begin_panic_fmt::h387afc7da9a10555
Dec 03 05:34:23.172 INFO [stdout]                                at libstd/panicking.rs:345
Dec 03 05:34:23.172 INFO [stdout]    7:     0x55f1f5cff8ff - http_test_server::tests::should_close_connection::h6b8030cb40423d38
Dec 03 05:34:23.172 INFO [stdout]                                at src/lib.rs:419
Dec 03 05:34:23.172 INFO [stdout]    8:     0x55f1f5cff509 - http_test_server::tests::should_close_connection::{{closure}}::h959ab38c4b15fd0b
Dec 03 05:34:23.172 INFO [stdout]                                at src/lib.rs:408
Dec 03 05:34:23.172 INFO [stdout]    9:     0x55f1f5d2f46d - core::ops::function::FnOnce::call_once::h2ab7d849b5d0a1ae
Dec 03 05:34:23.172 INFO [stdout]                                at libcore/ops/function.rs:238
Dec 03 05:34:23.172 INFO [stdout]   10:     0x55f1f5d4ccde - <F as alloc::boxed::FnBox<A>>::call_box::hac903f91577a156c
Dec 03 05:34:23.172 INFO [stdout]                                at libtest/lib.rs:1468
Dec 03 05:34:23.172 INFO [stdout]                                at libcore/ops/function.rs:238
Dec 03 05:34:23.172 INFO [stdout]                                at liballoc/boxed.rs:672
Dec 03 05:34:23.172 INFO [stdout]   11:     0x55f1f5db0249 - __rust_maybe_catch_panic
Dec 03 05:34:23.172 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Dec 03 05:34:23.172 INFO [stdout]   12:     0x55f1f5d6e932 - std::sys_common::backtrace::__rust_begin_short_backtrace::hb8f0d0309526c184
Dec 03 05:34:23.172 INFO [stdout]                                at libstd/panicking.rs:289
Dec 03 05:34:23.172 INFO [stdout]                                at libstd/panic.rs:392
Dec 03 05:34:23.172 INFO [stdout]                                at libtest/lib.rs:1423
Dec 03 05:34:23.172 INFO [stdout]                                at libstd/sys_common/backtrace.rs:136
Dec 03 05:34:23.172 INFO [stdout]   13:     0x55f1f5d6f3e4 - std::panicking::try::do_call::hc2f7c8b9a348d8db
Dec 03 05:34:23.172 INFO [stdout]                                at libstd/thread/mod.rs:409
Dec 03 05:34:23.172 INFO [stdout]                                at libstd/panic.rs:313
Dec 03 05:34:23.172 INFO [stdout]                                at libstd/panicking.rs:310
Dec 03 05:34:23.172 INFO [stdout]   14:     0x55f1f5db0249 - __rust_maybe_catch_panic
Dec 03 05:34:23.172 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Dec 03 05:34:23.172 INFO [stdout]   15:     0x55f1f5d5c61c - <F as alloc::boxed::FnBox<A>>::call_box::h6bfded618e480b32
Dec 03 05:34:23.172 INFO [stdout]                                at libstd/panicking.rs:289
Dec 03 05:34:23.172 INFO [stdout]                                at libstd/panic.rs:392
Dec 03 05:34:23.172 INFO [stdout]                                at libstd/thread/mod.rs:408
Dec 03 05:34:23.172 INFO [stdout]                                at liballoc/boxed.rs:672
Dec 03 05:34:23.172 INFO [stdout]   16:     0x55f1f5d9db8d - std::sys_common::thread::start_thread::h4c28257d67cdf9cb
Dec 03 05:34:23.172 INFO [stdout]                                at liballoc/boxed.rs:682
Dec 03 05:34:23.172 INFO [stdout]                                at libstd/sys_common/thread.rs:24
Dec 03 05:34:23.172 INFO [stdout]   17:     0x55f1f5d903a5 - std::sys::unix::thread::Thread::new::thread_start::hfecb2e7eff8fd34f
Dec 03 05:34:23.172 INFO [stdout]                                at libstd/sys/unix/thread.rs:90
Dec 03 05:34:23.172 INFO [stdout]   18:     0x7f80a25426b9 - start_thread
Dec 03 05:34:23.172 INFO [stdout]   19:     0x7f80a206241c - clone
Dec 03 05:34:23.172 INFO [stdout]   20:                0x0 - <unknown>
Dec 03 05:34:23.172 INFO [stdout] 
Dec 03 05:34:23.172 INFO [stdout] ---- tests::server_should_close_connection_when_dropped stdout ----
Dec 03 05:34:23.172 INFO [stdout] thread 'tests::server_should_close_connection_when_dropped' panicked at 'connection should be closed', src/lib.rs:647:13
Dec 03 05:34:23.172 INFO [stdout] stack backtrace:
Dec 03 05:34:23.172 INFO [stdout]    0:     0x55f1f5d9b87f - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h3a8b83e1922cbdde
Dec 03 05:34:23.172 INFO [stdout]                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Dec 03 05:34:23.172 INFO [stdout]    1:     0x55f1f5da5297 - std::sys_common::backtrace::print::h00d3155a29b4020f
Dec 03 05:34:23.172 INFO [stdout]                                at libstd/sys_common/backtrace.rs:71
Dec 03 05:34:23.172 INFO [stdout]                                at libstd/sys_common/backtrace.rs:59
Dec 03 05:34:23.172 INFO [stdout]    2:     0x55f1f5d9fe2f - std::panicking::default_hook::{{closure}}::hd7465c742e79e1bb
Dec 03 05:34:23.172 INFO [stdout]                                at libstd/panicking.rs:211
Dec 03 05:34:23.172 INFO [stdout]    3:     0x55f1f5d9fb2e - std::panicking::default_hook::h9e3966f2aa2a6e12
Dec 03 05:34:23.172 INFO [stdout]                                at libstd/panicking.rs:221
Dec 03 05:34:23.172 INFO [stdout]    4:     0x55f1f5da050e - std::panicking::rust_panic_with_hook::h587f091ae747d080
Dec 03 05:34:23.172 INFO [stdout]                                at libstd/panicking.rs:476
Dec 03 05:34:23.172 INFO [stdout]    5:     0x55f1f5d478b3 - std::panicking::begin_panic::he311024a6ea873de
Dec 03 05:34:23.172 INFO [stdout]                                at libstd/panicking.rs:410
Dec 03 05:34:23.172 INFO [stdout]    6:     0x55f1f5d0399b - http_test_server::tests::server_should_close_connection_when_dropped::h3e7d478794a36e33
Dec 03 05:34:23.172 INFO [stdout]                                at src/lib.rs:647
Dec 03 05:34:23.172 INFO [stdout]    7:     0x55f1f5d037b9 - http_test_server::tests::server_should_close_connection_when_dropped::{{closure}}::h977b14e7c460a3b0
Dec 03 05:34:23.172 INFO [stdout]                                at src/lib.rs:633
Dec 03 05:34:23.172 INFO [stdout]    8:     0x55f1f5d2fa3d - core::ops::function::FnOnce::call_once::hb9f17045a3896913
Dec 03 05:34:23.172 INFO [stdout]                                at libcore/ops/function.rs:238
Dec 03 05:34:23.172 INFO [stdout]    9:     0x55f1f5d4ccde - <F as alloc::boxed::FnBox<A>>::call_box::hac903f91577a156c
Dec 03 05:34:23.172 INFO [stdout]                                at libtest/lib.rs:1468
Dec 03 05:34:23.172 INFO [stdout]                                at libcore/ops/function.rs:238
Dec 03 05:34:23.172 INFO [stdout]                                at liballoc/boxed.rs:672
Dec 03 05:34:23.172 INFO [stdout]   10:     0x55f1f5db0249 - __rust_maybe_catch_panic
Dec 03 05:34:23.172 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Dec 03 05:34:23.172 INFO [stdout]   11:     0x55f1f5d6e932 - std::sys_common::backtrace::__rust_begin_short_backtrace::hb8f0d0309526c184
Dec 03 05:34:23.173 INFO [stdout]                                at libstd/panicking.rs:289
Dec 03 05:34:23.173 INFO [stdout]                                at libstd/panic.rs:392
Dec 03 05:34:23.173 INFO [stdout]                                at libtest/lib.rs:1423
Dec 03 05:34:23.173 INFO [stdout]                                at libstd/sys_common/backtrace.rs:136
Dec 03 05:34:23.173 INFO [stdout]   12:     0x55f1f5d6f3e4 - std::panicking::try::do_call::hc2f7c8b9a348d8db
Dec 03 05:34:23.173 INFO [stdout]                                at libstd/thread/mod.rs:409
Dec 03 05:34:23.173 INFO [stdout]                                at libstd/panic.rs:313
Dec 03 05:34:23.173 INFO [stdout]                                at libstd/panicking.rs:310
Dec 03 05:34:23.173 INFO [stdout]   13:     0x55f1f5db0249 - __rust_maybe_catch_panic
Dec 03 05:34:23.173 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Dec 03 05:34:23.173 INFO [stdout]   14:     0x55f1f5d5c61c - <F as alloc::boxed::FnBox<A>>::call_box::h6bfded618e480b32
Dec 03 05:34:23.173 INFO [stdout]                                at libstd/panicking.rs:289
Dec 03 05:34:23.173 INFO [stdout]                                at libstd/panic.rs:392
Dec 03 05:34:23.173 INFO [stdout]                                at libstd/thread/mod.rs:408
Dec 03 05:34:23.173 INFO [stdout]                                at liballoc/boxed.rs:672
Dec 03 05:34:23.173 INFO [stdout]   15:     0x55f1f5d9db8d - std::sys_common::thread::start_thread::h4c28257d67cdf9cb
Dec 03 05:34:23.173 INFO [stdout]                                at liballoc/boxed.rs:682
Dec 03 05:34:23.173 INFO [stdout]                                at libstd/sys_common/thread.rs:24
Dec 03 05:34:23.173 INFO [stdout]   16:     0x55f1f5d903a5 - std::sys::unix::thread::Thread::new::thread_start::hfecb2e7eff8fd34f
Dec 03 05:34:23.173 INFO [stdout]                                at libstd/sys/unix/thread.rs:90
Dec 03 05:34:23.173 INFO [stdout]   17:     0x7f80a25426b9 - start_thread
Dec 03 05:34:23.173 INFO [stdout]   18:     0x7f80a206241c - clone
Dec 03 05:34:23.173 INFO [stdout]   19:                0x0 - <unknown>
Dec 03 05:34:23.173 INFO [stdout] 
Dec 03 05:34:23.173 INFO [stdout] 
Dec 03 05:34:23.173 INFO [stdout] failures:
Dec 03 05:34:23.173 INFO [stdout]     tests::server_should_close_connection_when_dropped
Dec 03 05:34:23.173 INFO [stdout]     tests::should_close_connection
Dec 03 05:34:23.173 INFO [stdout] 
Dec 03 05:34:23.174 INFO [stdout] test result: FAILED. 27 passed; 2 failed; 0 ignored; 0 measured; 0 filtered out
Dec 03 05:34:23.174 INFO [stdout] 
Dec 03 05:34:23.176 INFO [stderr] error: test failed, to rerun pass '--lib'
Dec 03 05:34:23.178 INFO [stderr] su: No module specific data is present
Dec 03 05:34:23.685 INFO running `"docker" "inspect" "060a30f21aae253877241a6e8086ce0520a7669f49e308a1cc6b33386e65f0a3"`
Dec 03 05:34:23.996 INFO running `"docker" "rm" "-f" "060a30f21aae253877241a6e8086ce0520a7669f49e308a1cc6b33386e65f0a3"`
Dec 03 05:34:24.275 INFO [stdout] 060a30f21aae253877241a6e8086ce0520a7669f49e308a1cc6b33386e65f0a3
